[INFO] cloning repository https://github.com/yangszwei/rustcoon
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/yangszwei/rustcoon" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fyangszwei%2Frustcoon", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fyangszwei%2Frustcoon'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] f3661901948cdb003a8f5216f1e6efe08c5e7f74
[INFO] testing yangszwei/rustcoon against try#b8e88e5ddf5521a9f43ee3f62a702388c713e4bb for pr-155114
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fyangszwei%2Frustcoon" "/workspace/builds/worker-4-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-4-tc2/source'...
[INFO] [stderr] done.
[INFO] removed /workspace/builds/worker-4-tc2/source/rust-toolchain.toml
[INFO] started tweaking git repo https://github.com/yangszwei/rustcoon
[INFO] finished tweaking git repo https://github.com/yangszwei/rustcoon
[INFO] tweaked toml for git repo https://github.com/yangszwei/rustcoon written to /workspace/builds/worker-4-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/yangszwei/rustcoon on toolchain b8e88e5ddf5521a9f43ee3f62a702388c713e4bb
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/yangszwei/rustcoon already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] bb256132eea6f8345c368d41376e660d284ebb62587872e24cb86ee91860944a
[INFO] running `Command { std: "docker" "start" "-a" "bb256132eea6f8345c368d41376e660d284ebb62587872e24cb86ee91860944a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "bb256132eea6f8345c368d41376e660d284ebb62587872e24cb86ee91860944a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "bb256132eea6f8345c368d41376e660d284ebb62587872e24cb86ee91860944a", kill_on_drop: false }`
[INFO] [stdout] bb256132eea6f8345c368d41376e660d284ebb62587872e24cb86ee91860944a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] c07e6db074242bc919b15fe338966a6441eb06279519a8f086ab017eaf9170e5
[INFO] running `Command { std: "docker" "start" "-a" "c07e6db074242bc919b15fe338966a6441eb06279519a8f086ab017eaf9170e5", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling pin-project-lite v0.2.17
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling futures-core v0.3.32
[INFO] [stderr]    Compiling bytes v1.11.1
[INFO] [stderr]    Compiling log v0.4.29
[INFO] [stderr]    Compiling futures-sink v0.3.32
[INFO] [stderr]    Compiling itoa v1.0.17
[INFO] [stderr]    Compiling futures-task v0.3.32
[INFO] [stderr]    Compiling futures-io v0.3.32
[INFO] [stderr]    Compiling mio v1.1.1
[INFO] [stderr]    Compiling socket2 v0.6.3
[INFO] [stderr]    Compiling icu_properties_data v2.1.2
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling slab v0.4.12
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling subtle v2.6.1
[INFO] [stderr]    Compiling encoding_index_tests v0.1.4
[INFO] [stderr]    Compiling form_urlencoded v1.2.2
[INFO] [stderr]    Compiling zerocopy v0.8.42
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling byteorder v1.5.0
[INFO] [stderr]    Compiling futures-channel v0.3.32
[INFO] [stderr]    Compiling ring v0.17.14
[INFO] [stderr]    Compiling zeroize v1.8.2
[INFO] [stderr]    Compiling safe-transmute v0.11.3
[INFO] [stderr]    Compiling encoding-index-tradchinese v1.20141219.5
[INFO] [stderr]    Compiling rustls-pki-types v1.14.0
[INFO] [stderr]    Compiling encoding-index-korean v1.20141219.5
[INFO] [stderr]    Compiling encoding-index-japanese v1.20141219.5
[INFO] [stderr]    Compiling encoding-index-simpchinese v1.20141219.5
[INFO] [stderr]    Compiling encoding-index-singlebyte v1.20141219.5
[INFO] [stderr]    Compiling byteordered v0.6.0
[INFO] [stderr]    Compiling untrusted v0.9.0
[INFO] [stderr]    Compiling crypto-common v0.1.7
[INFO] [stderr]    Compiling allocator-api2 v0.2.21
[INFO] [stderr]    Compiling rustls v0.23.37
[INFO] [stderr]    Compiling foldhash v0.1.5
[INFO] [stderr]    Compiling ryu v1.0.23
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling crc-catalog v2.4.0
[INFO] [stderr]    Compiling encoding v0.2.33
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling event-listener v5.4.1
[INFO] [stderr]    Compiling http v1.4.0
[INFO] [stderr]    Compiling crc v3.4.0
[INFO] [stderr]    Compiling crossbeam-queue v0.3.12
[INFO] [stderr]    Compiling try-lock v0.2.5
[INFO] [stderr]    Compiling webpki-roots v1.0.6
[INFO] [stderr]    Compiling atomic-waker v1.1.2
[INFO] [stderr]    Compiling hashbrown v0.15.5
[INFO] [stderr]    Compiling webpki-roots v0.26.11
[INFO] [stderr]    Compiling tower-service v0.3.3
[INFO] [stderr]    Compiling want v0.3.1
[INFO] [stderr]    Compiling libsqlite3-sys v0.30.1
[INFO] [stderr]    Compiling tinyvec v1.11.0
[INFO] [stderr]    Compiling chrono v0.4.44
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling tokio v1.50.0
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling spin v0.9.8
[INFO] [stderr]    Compiling sync_wrapper v1.0.2
[INFO] [stderr]    Compiling ipnet v2.12.0
[INFO] [stderr]    Compiling tower-layer v0.3.3
[INFO] [stderr]    Compiling toml_parser v1.0.9+spec-1.1.0
[INFO] [stderr]    Compiling hashlink v0.10.0
[INFO] [stderr]    Compiling unicode-normalization v0.1.25
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling unicode-properties v0.1.4
[INFO] [stderr]    Compiling base64 v0.22.1
[INFO] [stderr]    Compiling http-body v1.0.1
[INFO] [stderr]    Compiling unicode-bidi v0.3.18
[INFO] [stderr]    Compiling anyhow v1.0.102
[INFO] [stderr]    Compiling http-body-util v0.1.3
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling hmac v0.12.1
[INFO] [stderr]    Compiling atoi v2.0.0
[INFO] [stderr]    Compiling stringprep v0.1.5
[INFO] [stderr]    Compiling pathdiff v0.2.3
[INFO] [stderr]    Compiling whoami v1.6.1
[INFO] [stderr]    Compiling dotenvy v0.15.7
[INFO] [stderr]    Compiling home v0.5.12
[INFO] [stderr]    Compiling rand_core v0.9.5
[INFO] [stderr]    Compiling hkdf v0.12.4
[INFO] [stderr]    Compiling flume v0.11.1
[INFO] [stderr]    Compiling md-5 v0.10.6
[INFO] [stderr]    Compiling bitflags v2.11.0
[INFO] [stderr]    Compiling regex-syntax v0.8.10
[INFO] [stderr]    Compiling iri-string v0.7.10
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling fastrand v2.4.1
[INFO] [stderr]    Compiling uuid v1.23.0
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling toml_datetime v1.0.0+spec-1.1.0
[INFO] [stderr]    Compiling serde_spanned v1.0.4
[INFO] [stderr]    Compiling toml v1.0.6+spec-1.1.0
[INFO] [stderr]    Compiling futures-executor v0.3.32
[INFO] [stderr]    Compiling rustls-webpki v0.103.10
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling config v0.15.21
[INFO] [stderr]    Compiling synstructure v0.13.2
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling tokio-macros v2.6.1
[INFO] [stderr]    Compiling zerofrom-derive v0.1.6
[INFO] [stderr]    Compiling yoke-derive v0.8.1
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling zerovec-derive v0.11.2
[INFO] [stderr]    Compiling displaydoc v0.2.5
[INFO] [stderr]    Compiling futures-macro v0.3.32
[INFO] [stderr]    Compiling snafu-derive v0.8.9
[INFO] [stderr]    Compiling async-trait v0.1.89
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling tokio-stream v0.1.18
[INFO] [stderr]    Compiling pin-project-internal v1.1.11
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling zerofrom v0.1.6
[INFO] [stderr]    Compiling yoke v0.8.1
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling rustcoon-dicom v0.1.0 (/opt/rustwide/workdir/crates/domain-dicom)
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling rand v0.9.2
[INFO] [stderr]    Compiling zerovec v0.11.5
[INFO] [stderr]    Compiling zerotrie v0.2.3
[INFO] [stderr]    Compiling opentelemetry v0.31.0
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling pin-project v1.1.11
[INFO] [stderr]    Compiling snafu v0.8.9
[INFO] [stderr]    Compiling tinystr v0.8.2
[INFO] [stderr]    Compiling potential_utf v0.1.4
[INFO] [stderr]    Compiling icu_collections v2.1.1
[INFO] [stderr]    Compiling icu_locale_core v2.1.1
[INFO] [stderr]    Compiling icu_provider v2.1.1
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling either v1.15.0
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling rustcoon-config v0.1.0 (/opt/rustwide/workdir/crates/platform-config)
[INFO] [stderr]    Compiling tracing-serde v0.2.0
[INFO] [stderr]    Compiling icu_properties v2.1.2
[INFO] [stderr]    Compiling icu_normalizer v2.1.1
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling itertools v0.14.0
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling rustcoon-application-entity v0.1.0 (/opt/rustwide/workdir/crates/domain-application-entity)
[INFO] [stderr]    Compiling futures-intrusive v0.5.0
[INFO] [stderr]    Compiling idna_adapter v1.2.1
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling sqlx-core v0.8.6
[INFO] [stderr]    Compiling tracing-opentelemetry v0.32.1
[INFO] [stderr]    Compiling dicom-core v0.9.1
[INFO] [stderr]    Compiling prost-derive v0.14.3
[INFO] [stderr]    Compiling tokio-util v0.7.18
[INFO] [stderr]    Compiling rustcoon-storage v0.1.0 (/opt/rustwide/workdir/crates/ports-storage)
[INFO] [stderr]    Compiling rustcoon-storage-filesystem v0.1.0 (/opt/rustwide/workdir/crates/adapter-storage-filesystem)
[INFO] [stderr]    Compiling rustcoon-storage-replicated v0.1.0 (/opt/rustwide/workdir/crates/adapter-storage-replicated)
[INFO] [stderr]    Compiling opentelemetry_sdk v0.31.0
[INFO] [stderr]    Compiling dicom-dictionary-std v0.9.0
[INFO] [stderr]    Compiling h2 v0.4.13
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling rustcoon-runtime v0.1.0 (/opt/rustwide/workdir/crates/platform-runtime)
[INFO] [stderr]    Compiling prost v0.14.3
[INFO] [stderr]    Compiling dicom-encoding v0.9.1
[INFO] [stderr]    Compiling sqlx-postgres v0.8.6
[INFO] [stderr]    Compiling sqlx-sqlite v0.8.6
[INFO] [stderr]    Compiling tower-http v0.6.8
[INFO] [stderr]    Compiling dicom-transfer-syntax-registry v0.9.1
[INFO] [stderr]    Compiling dicom-parser v0.9.1
[INFO] [stderr]    Compiling dicom-ul v0.9.1
[INFO] [stderr]    Compiling dicom-object v0.9.1
[INFO] [stderr]    Compiling rustcoon-ul v0.1.0 (/opt/rustwide/workdir/crates/protocols-ul)
[INFO] [stderr]    Compiling rustcoon-index v0.1.0 (/opt/rustwide/workdir/crates/ports-index)
[INFO] [stderr]    Compiling dicom-json v0.9.0
[INFO] [stderr]    Compiling rustcoon-ingest v0.1.0 (/opt/rustwide/workdir/crates/app-ingest)
[INFO] [stderr]    Compiling rustcoon-query v0.1.0 (/opt/rustwide/workdir/crates/app-query)
[INFO] [stderr]    Compiling rustcoon-retrieve v0.1.0 (/opt/rustwide/workdir/crates/app-retrieve)
[INFO] [stderr]    Compiling rustcoon-dimse v0.1.0 (/opt/rustwide/workdir/crates/protocols-dimse)
[INFO] [stderr]    Compiling hyper v1.8.1
[INFO] [stderr]    Compiling sqlx-macros-core v0.8.6
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling sqlx-macros v0.8.6
[INFO] [stderr]    Compiling hyper-timeout v0.5.2
[INFO] [stderr]    Compiling reqwest v0.12.28
[INFO] [stderr]    Compiling tonic v0.14.5
[INFO] [stderr]    Compiling sqlx v0.8.6
[INFO] [stderr]    Compiling rustcoon-index-sqlite v0.1.0 (/opt/rustwide/workdir/crates/adapter-index-sqlite)
[INFO] [stderr]    Compiling rustcoon-index-postgres v0.1.0 (/opt/rustwide/workdir/crates/adapter-index-postgres)
[INFO] [stderr]    Compiling opentelemetry-http v0.31.0
[INFO] [stderr]    Compiling tonic-prost v0.14.5
[INFO] [stderr]    Compiling opentelemetry-proto v0.31.0
[INFO] [stderr]    Compiling opentelemetry-otlp v0.31.0
[INFO] [stderr]    Compiling rustcoon-telemetry v0.1.0 (/opt/rustwide/workdir/crates/platform-telemetry)
[INFO] [stderr]    Compiling rustcoon-orchestration v0.1.0 (/opt/rustwide/workdir/crates/platform-orchestration)
[INFO] [stderr]    Compiling rustcoon v0.1.0 (/opt/rustwide/workdir/apps/rustcoon)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 2m 30s
[INFO] running `Command { std: "docker" "inspect" "c07e6db074242bc919b15fe338966a6441eb06279519a8f086ab017eaf9170e5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c07e6db074242bc919b15fe338966a6441eb06279519a8f086ab017eaf9170e5", kill_on_drop: false }`
[INFO] [stdout] c07e6db074242bc919b15fe338966a6441eb06279519a8f086ab017eaf9170e5
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 59e0900edc9f2ea60cbf7d73708a51197bbfdd7aa423657c34ad6a3e7634f008
[INFO] running `Command { std: "docker" "start" "-a" "59e0900edc9f2ea60cbf7d73708a51197bbfdd7aa423657c34ad6a3e7634f008", kill_on_drop: false }`
[INFO] [stderr]    Compiling rustcoon-dimse v0.1.0 (/opt/rustwide/workdir/crates/protocols-dimse)
[INFO] [stderr]    Compiling rustcoon-ul v0.1.0 (/opt/rustwide/workdir/crates/protocols-ul)
[INFO] [stderr]    Compiling rustcoon-storage-filesystem v0.1.0 (/opt/rustwide/workdir/crates/adapter-storage-filesystem)
[INFO] [stderr]    Compiling rustcoon-index-postgres v0.1.0 (/opt/rustwide/workdir/crates/adapter-index-postgres)
[INFO] [stderr]    Compiling rustcoon-index-sqlite v0.1.0 (/opt/rustwide/workdir/crates/adapter-index-sqlite)
[INFO] [stderr]    Compiling rustcoon-query v0.1.0 (/opt/rustwide/workdir/crates/app-query)
[INFO] [stderr]    Compiling rustcoon-retrieve v0.1.0 (/opt/rustwide/workdir/crates/app-retrieve)
[INFO] [stderr]    Compiling rustcoon-ingest v0.1.0 (/opt/rustwide/workdir/crates/app-ingest)
[INFO] [stderr]    Compiling rustcoon-index v0.1.0 (/opt/rustwide/workdir/crates/ports-index)
[INFO] [stderr]    Compiling rustcoon-application-entity v0.1.0 (/opt/rustwide/workdir/crates/domain-application-entity)
[INFO] [stderr]    Compiling rustcoon v0.1.0 (/opt/rustwide/workdir/apps/rustcoon)
[INFO] [stderr]    Compiling rustcoon-orchestration v0.1.0 (/opt/rustwide/workdir/crates/platform-orchestration)
[INFO] [stderr]    Compiling rustcoon-telemetry v0.1.0 (/opt/rustwide/workdir/crates/platform-telemetry)
[INFO] [stderr]    Compiling rustcoon-runtime v0.1.0 (/opt/rustwide/workdir/crates/platform-runtime)
[INFO] [stderr]    Compiling rustcoon-config v0.1.0 (/opt/rustwide/workdir/crates/platform-config)
[INFO] [stderr]    Compiling rustcoon-storage-replicated v0.1.0 (/opt/rustwide/workdir/crates/adapter-storage-replicated)
[INFO] [stderr]    Compiling rustcoon-dicom v0.1.0 (/opt/rustwide/workdir/crates/domain-dicom)
[INFO] [stderr]    Compiling rustcoon-storage v0.1.0 (/opt/rustwide/workdir/crates/ports-storage)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 18.22s
[INFO] running `Command { std: "docker" "inspect" "59e0900edc9f2ea60cbf7d73708a51197bbfdd7aa423657c34ad6a3e7634f008", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "59e0900edc9f2ea60cbf7d73708a51197bbfdd7aa423657c34ad6a3e7634f008", kill_on_drop: false }`
[INFO] [stdout] 59e0900edc9f2ea60cbf7d73708a51197bbfdd7aa423657c34ad6a3e7634f008
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 08ccda93fcdd43bc326602d918c409f3b8274ad261fb618b4abe340085613290
[INFO] running `Command { std: "docker" "start" "-a" "08ccda93fcdd43bc326602d918c409f3b8274ad261fb618b4abe340085613290", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.73s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/rustcoon-1b129f948b0ee3f9)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rustcoon_application_entity-25b010a7a9979c09)
[INFO] [stdout] 
[INFO] [stdout] running 18 tests
[INFO] [stdout] test model::tests::local_try_from_config_rejects_invalid_title ... ok
[INFO] [stdout] test model::tests::local_try_from_config_succeeds ... ok
[INFO] [stdout] test model::tests::remote_try_from_config_rejects_invalid_title ... ok
[INFO] [stdout] test registry::tests::inbound_access_requires_called_local_and_calling_remote ... ok
[INFO] [stdout] test model::tests::remote_try_from_config_succeeds ... ok
[INFO] [stdout] test registry::tests::inbound_plan_errors_are_specific ... ok
[INFO] [stdout] test registry::tests::inbound_plan_uses_listener_and_peer_ip ... ok
[INFO] [stdout] test registry::tests::outbound_errors_are_specific ... ok
[INFO] [stdout] test registry::tests::outbound_plan_chooses_loopback_for_local_destination ... ok
[INFO] [stdout] test registry::tests::registry_iterators_return_expected_counts ... ok
[INFO] [stdout] test registry::tests::rejects_duplicate_titles_across_local_and_remote ... ok
[INFO] [stdout] test title::tests::accepts_exactly_sixteen_characters ... ok
[INFO] [stdout] test title::tests::accepts_leading_and_trailing_spaces_if_not_all_spaces ... ok
[INFO] [stdout] test registry::tests::outbound_plan_chooses_tcp_for_remote_destination ... ok
[INFO] [stdout] test title::tests::accepts_valid_title ... ok
[INFO] [stdout] test title::tests::rejects_empty_or_spaces ... ok
[INFO] [stdout] test title::tests::rejects_invalid_chars ... ok
[INFO] [stdout] test title::tests::rejects_too_long ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 18 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/association_routing_it.rs (/opt/rustwide/target/debug/deps/association_routing_it-1c728f7cdb08b99f)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test inbound_association_rejects_unknown_calling_ae_title ... ignored, requires local TCP bind capability
[INFO] [stdout] test inbound_association_rejects_wrong_called_ae_title ... ignored, requires local TCP bind capability
[INFO] [stdout] test plan_inbound_drives_real_server_association ... ignored, requires local TCP bind capability
[INFO] [stdout] test plan_outbound_drives_real_client_association ... ignored, requires local TCP bind capability
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 4 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rustcoon_config-9120320129bfd17a)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test database::tests::postgres_backend_can_be_constructed ... ok
[INFO] [stdout] test database::tests::database_defaults_to_sqlite_backend ... ok
[INFO] [stdout] test database::tests::postgres_defaults_are_sensible_for_local_development ... ok
[INFO] [stdout] test database::tests::sqlite_defaults_are_sensible_for_local_development ... ok
[INFO] [stdout] test storage::tests::filesystem_defaults_to_repo_relative_root ... ok
[INFO] [stdout] test storage::tests::storage_defaults_to_filesystem_backend ... ok
[INFO] [stdout] test database::tests::sqlite_backend_can_be_constructed ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rustcoon_dicom-760199a9f427bc82)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test identity::tests::identities_preserve_hierarchy ... ok
[INFO] [stdout] test metadata::tests::instance_metadata_exposes_values ... ok
[INFO] [stdout] test identity::tests::identity_accessors_expose_direct_uid_fields ... ok
[INFO] [stdout] test metadata::tests::metadata_normalizes_blank_text_to_none ... ok
[INFO] [stdout] test record::tests::records_expose_normalized_domain_state ... ok
[INFO] [stdout] test uid::tests::uid_types_accept_valid_values ... ok
[INFO] [stdout] test uid::tests::uid_types_reject_invalid_values ... ok
[INFO] [stdout] test uid::tests::uid_types_support_from_str_round_trip ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rustcoon_dimse-7e487545472cd6a8)
[INFO] [stdout] 
[INFO] [stdout] running 63 tests
[INFO] [stdout] test error_handler::tests::default_handler_replies_release_when_peer_requests_release ... ok
[INFO] [stdout] test instrumentation::tests::classifies_core_dimse_errors ... ok
[INFO] [stdout] test instrumentation::tests::status_class_maps_dicom_status_ranges ... ok
[INFO] [stdout] test error_handler::tests::default_handler_aborts_on_other_errors ... ok
[INFO] [stdout] test listener::tests::bind_from_registry_requires_existing_local_ae ... ok
[INFO] [stdout] test error_handler::tests::default_handler_stops_on_closed_or_aborted_ul ... ok
[INFO] [stdout] test listener::tests::with_abstract_syntaxes_empty_iterator_keeps_listener_unconfigured ... ok
[INFO] [stdout] test message::reader::tests::read_data_pdv_returns_none_without_active_dataset ... ok
[INFO] [stdout] test message::writer::tests::max_pdv_data_len_for_peer_handles_edge_values ... ok
[INFO] [stdout] test listener::tests::with_abstract_syntaxes_accepts_any_added_uid ... ok
[INFO] [stdout] test service::command::tests::c_store_does_not_fallback_to_requested_sop_uids ... ok
[INFO] [stdout] test service::command::tests::command_field_from_raw_maps_known_and_unknown_values ... ok
[INFO] [stdout] test service::command::tests::display_formats_known_and_unknown_command_fields ... ok
[INFO] [stdout] test service::command::tests::parses_minimal_command_with_optional_fields_absent ... ok
[INFO] [stdout] test message::writer::tests::writer_rejects_invalid_data_pdv_inputs ... ok
[INFO] [stdout] test service::command::tests::parses_all_known_fields_including_requested_uid_fallbacks ... ok
[INFO] [stdout] test message::writer::tests::writer_rejects_unnegotiated_and_non_accepted_contexts ... ok
[INFO] [stdout] test message::reader::tests::reader_maps_abort_and_release_pdus_to_expected_errors ... ok
[INFO] [stdout] test service::command::tests::priority_maps_unknown_values ... ok
[INFO] [stdout] test service::command::tests::rejects_invalid_optional_fields ... ok
[INFO] [stdout] test service::command::tests::rejects_invalid_required_u16_fields ... ok
[INFO] [stdout] test service::command::tests::rejects_invalid_optional_string_fields ... ok
[INFO] [stdout] test service::command::tests::rejects_missing_required_command_field ... ok
[INFO] [stdout] test service::query::find_message::tests::parses_find_request_and_builds_pending_and_final_responses ... ok
[INFO] [stdout] test service::query::find_message::tests::rejects_invalid_find_requests ... ok
[INFO] [stdout] test service::query::find_message::tests::response_helpers_include_optional_failure_fields ... ok
[INFO] [stdout] test service::query::find_provider::tests::map_find_response_status_codes_match_expected_values ... ok
[INFO] [stdout] test service::query::find_provider::tests::bindings_cover_patient_and_study_root_find_models ... ok
[INFO] [stdout] test service::query::find_provider::tests::query_error_mapping_marks_identifier_element_as_offending ... ok
[INFO] [stdout] test service::registry::tests::provider_lookup_uses_exact_then_wildcard ... ok
[INFO] [stdout] test service::registry::tests::provider_lookup_without_sop_uid_selects_single_bound_provider ... ok
[INFO] [stdout] test service::registry::tests::register_described_registers_all_bindings ... ok
[INFO] [stdout] test service::registry::tests::supported_abstract_syntax_uids_are_sorted_unique_and_skip_wildcard ... ok
[INFO] [stdout] test service::store::store_message::tests::error_comment_is_truncated_to_lo_length ... ok
[INFO] [stdout] test service::store::store_message::tests::rejects_invalid_store_requests ... ok
[INFO] [stdout] test service::store::store_message::tests::parses_store_request_and_builds_success_response ... ok
[INFO] [stdout] test service::store::store_message::tests::status_codes_match_expected_values ... ok
[INFO] [stdout] test service::store::store_message::tests::rejects_missing_required_store_request_fields ... ok
[INFO] [stdout] test service::store::store_provider::tests::bindings_cover_configured_sop_classes ... ok
[INFO] [stdout] test service::store::store_provider::tests::helper_functions_cover_validation_error_paths_and_status_mapping ... ok
[INFO] [stdout] test service::store::store_message::tests::response_builder_preserves_request_identity_and_requested_status ... ok
[INFO] [stdout] test message::writer::tests::writer_rejects_invalid_command_objects ... ok
[INFO] [stdout] test service::tests::service_binding_new_sets_command_and_uid ... ok
[INFO] [stdout] test service::store::store_provider::tests::store_request_parser_requires_dataset_and_priority ... ok
[INFO] [stdout] test service::verification::echo_message::tests::echoes_default_verification_uid_when_command_omits_it ... ok
[INFO] [stdout] test service::verification::echo_message::tests::parses_echo_request_and_builds_success_response ... ok
[INFO] [stdout] test service::verification::echo_message::tests::rejects_echo_request_with_data_set ... ok
[INFO] [stdout] test service::verification::echo_message::tests::rejects_echo_request_without_message_id ... ok
[INFO] [stdout] test service::verification::echo_message::tests::rejects_non_echo_command_field ... ok
[INFO] [stdout] test service::verification::echo_provider::tests::bindings_declare_c_echo_for_verification_uid ... ok
[INFO] [stdout] test service::registry::tests::handle_returns_error_when_no_provider_matches ... ok
[INFO] [stdout] test message::reader::tests::reader_rejects_command_before_consuming_dataset ... ok
[INFO] [stdout] test service::registry::tests::handle_dispatches_to_described_provider_binding ... ok
[INFO] [stdout] test service::registry::tests::handle_dispatches_to_exact_registered_provider ... ok
[INFO] [stdout] test service::store::store_provider::tests::build_ingest_request_extracts_metadata_and_rejects_invalid_datasets ... ok
[INFO] [stdout] test message::reader::tests::reader_handles_pending_pdv_queue_and_finished_dataset_state ... ok
[INFO] [stdout] test message::writer::tests::writer_splits_data_by_peer_max_pdu_and_reader_reassembles ... ok
[INFO] [stdout] test service::store::store_provider::tests::storage_provider_returns_sop_class_mismatch_status ... ok
[INFO] [stdout] test service::store::store_provider::tests::drain_remaining_data_set_consumes_pending_store_payload ... ok
[INFO] [stdout] test service::store::store_provider::tests::storage_provider_returns_cxxx_for_sop_instance_uid_mismatch ... ok
[INFO] [stdout] test service::store::store_provider::tests::storage_provider_handles_store_and_returns_success_response ... ok
[INFO] [stdout] test service::store::store_provider::tests::storage_provider_rejects_abstract_syntax_mismatch_with_command ... ok
[INFO] [stdout] test message::reader::tests::reader_rejects_invalid_dataset_stream_shapes ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 63 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.09s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/common.rs (/opt/rustwide/target/debug/deps/common-e21d3f57d47f30f2)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/dimse_association_flow_it.rs (/opt/rustwide/target/debug/deps/dimse_association_flow_it-31a7da51a05d2471)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test reader_and_writer_protocol_error_paths_are_reported ... ok
[INFO] [stdout] test listener_accept_and_default_release_handler_work ... ok
[INFO] [stdout] test context_route_plan_and_message_cycle_error_paths_work ... ok
[INFO] [stdout] test listener_accept_and_custom_error_handler_paths_work ... ok
[INFO] [stderr]      Running tests/query_provider_it.rs (/opt/rustwide/target/debug/deps/query_provider_it-3f7374c83a1a01a9)
[INFO] [stdout] test context_reader_and_writer_round_trip_works ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.09s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test query_provider_returns_identifier_error_for_invalid_request_identifier ... ok
[INFO] [stdout] test query_provider_returns_pending_identifier_then_final_success ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.10s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/verification_provider_it.rs (/opt/rustwide/target/debug/deps/verification_provider_it-1aaee8eaf2fb8ce4)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test verification_provider_handle_round_trips_c_echo_response ... ok
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rustcoon_index-aef61d80adb19188)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 16 tests
[INFO] [stdout] test query::tests::paging_new_sets_expected_values ... ok
[INFO] [stdout] test query::tests::page_summary_tracks_returned_count ... ok
[INFO] [stdout] test attribute_path::tests::matches_with_any_item_wildcard ... ok
[INFO] [stdout] test attribute_path::tests::validate_rejects_invalid_shapes ... ok
[INFO] [stdout] test attribute_path::tests::build_simple_tag_path ... ok
[INFO] [stdout] test read::tests::stored_object_ref_builder_methods_set_optional_fields ... ok
[INFO] [stdout] test query::tests::query_rejects_empty_projection_and_invalid_paging ... ok
[INFO] [stdout] test predicate::tests::complex_predicate_tree_can_be_built ... ok
[INFO] [stdout] test write::tests::catalog_store_marker_trait_accepts_combined_store ... ok
[INFO] [stdout] test write::tests::instance_upsert_request_builder_methods_set_optional_fields ... ok
[INFO] [stdout] test predicate::tests::datetime_range_predicate_variant_validates_like_other_attribute_rules ... ok
[INFO] [stdout] test error::tests::constructors_populate_expected_variants ... ok
[INFO] [stdout] test predicate::tests::range_helpers_build_expected_shapes ... ok
[INFO] [stdout] test query::tests::query_can_model_free_form_projection_and_predicates ... ok
[INFO] [stdout] test attribute_path::tests::display_attribute_path ... ok
[INFO] [stdout] test write::tests::mock_catalog_store_traits_are_exercised ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 16 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rustcoon_index_postgres-f6237173dd62553c)
[INFO] [stdout] 
[INFO] [stdout] running 24 tests
[INFO] [stdout] test config::tests::config_builder_clamps_pool_size ... ok
[INFO] [stdout] test error::tests::maps_other_errors_as_backend_failures ... ok
[INFO] [stdout] test error::tests::maps_pool_timeout_as_unavailable ... ok
[INFO] [stdout] test query::compile::tests::compiler_supports_study_and_series_distinct_queries ... ok
[INFO] [stdout] test query::compile::tests::materialize_projection_builds_projected_object ... ok
[INFO] [stdout] test query::compile::tests::materialize_projection_builds_nested_sequence_structure ... ok
[INFO] [stdout] test query::compile::tests::materialize_projection_supports_mapped_person_name_vr ... ok
[INFO] [stdout] test read::tests::attribute_serialization_round_trips_wrapped_dicom_json ... ok
[INFO] [stdout] test read::tests::blob_ref_from_parts_handles_missing_and_present_blob ... ok
[INFO] [stdout] test read::tests::instance_entry_from_data_builds_record_and_blob ... ok
[INFO] [stdout] test read::tests::format_tag_key_is_usable_for_projected_documents ... ok
[INFO] [stdout] test read::tests::study_entry_from_data_builds_normalized_record ... ok
[INFO] [stdout] test schema::tests::format_tag_key_uses_dicom_json_shape ... ok
[INFO] [stdout] test schema::tests::schema_resolves_known_indexed_attributes ... ok
[INFO] [stdout] test write::tests::desired_state_from_request_captures_persisted_shape ... ok
[INFO] [stdout] test store::tests::connect_rejects_invalid_connection_string ... ok
[INFO] [stdout] test write::tests::existing_state_match_detects_unchanged_and_changed_state ... ok
[INFO] [stdout] test read::tests::series_entry_from_data_builds_record ... ok
[INFO] [stdout] test query::compile::tests::compiler_supports_nested_concrete_paths_and_sequence_matching ... ok
[INFO] [stdout] test query::compile::tests::compiler_compiles_datetime_range_matching_matrix ... ok
[INFO] [stdout] test query::compile::tests::compiler_compiles_datetime_range_for_custom_tag_with_json_fallback ... ok
[INFO] [stdout] test query::compile::tests::compiler_uses_indexed_columns_and_jsonb_fallback_for_image_level ... ok
[INFO] [stdout] test query::compile::tests::compiler_supports_patient_root_patient_queries ... ok
[INFO] [stdout] test store::tests::new_initializes_store_with_pool ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 24 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rustcoon_index_sqlite-212c41d888643e80)
[INFO] [stdout] 
[INFO] [stdout] running 15 tests
[INFO] [stdout] test error::tests::maps_pool_timeout_as_unavailable ... ok
[INFO] [stdout] test config::tests::config_builder_clamps_pool_size ... ok
[INFO] [stdout] test error::tests::maps_other_errors_as_backend_failures ... ok
[INFO] [stdout] test query::compile::tests::compiler_supports_study_and_series_distinct_queries ... ok
[INFO] [stdout] test schema::tests::format_tag_key_uses_dicom_json_shape ... ok
[INFO] [stdout] test query::compile::tests::materialize_projection_builds_projected_object ... ok
[INFO] [stdout] test query::compile::tests::materialize_projection_supports_mapped_person_name_vr ... ok
[INFO] [stdout] test schema::tests::schema_resolves_known_indexed_attributes ... ok
[INFO] [stdout] test write::tests::desired_state_from_request_captures_persisted_shape ... ok
[INFO] [stdout] test store::tests::new_initializes_store_with_pool ... ok
[INFO] [stdout] test write::tests::existing_state_match_detects_unchanged_and_changed_state ... ok
[INFO] [stdout] test query::compile::tests::compiler_supports_patient_root_patient_queries ... ok
[INFO] [stdout] test query::compile::tests::compiler_uses_indexed_columns_and_json_fallback_for_image_level ... ok
[INFO] [stdout] test query::compile::tests::compiler_supports_nested_concrete_paths_and_sequence_matching ... ok
[INFO] [stdout] test store::tests::connect_applies_schema_migrations ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rustcoon_ingest-065b5ca5d9aa8b62)
[INFO] [stdout] 
[INFO] [stdout] running 12 tests
[INFO] [stdout] test keying::tests::hierarchical_resolver_supports_custom_prefix_and_extension ... ok
[INFO] [stdout] test keying::tests::hierarchical_resolver_uses_uid_hierarchy ... ok
[INFO] [stdout] test model::tests::ingest_request_builders_override_optional_fields ... ok
[INFO] [stdout] test model::tests::ingest_request_defaults_match_archive_ingest_expectations ... ok
[INFO] [stdout] test model::tests::ingest_result_exposes_outcome_and_blob_reference ... ok
[INFO] [stdout] test service::tests::ingest_propagates_begin_write_errors ... ok
[INFO] [stdout] test service::tests::existing_instance_uses_catalog_lookup ... ok
[INFO] [stdout] test service::tests::ingest_rolls_back_blob_when_catalog_update_fails ... ok
[INFO] [stdout] test service::tests::ingest_streams_payload_and_updates_catalog ... ok
[INFO] [stdout] test service::tests::ingest_surfaces_abort_failure_after_payload_read_error ... ok
[INFO] [stdout] test service::tests::ingest_propagates_commit_and_head_failures ... ok
[INFO] [stdout] test service::tests::ingest_preserves_explicit_durability_and_maps_remaining_outcomes ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rustcoon_orchestration-8f2586cea0c5d972)
[INFO] [stdout] 
[INFO] [stdout] running 12 tests
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rustcoon_query-da5462dc02272468)
[INFO] [stdout] test core::error::tests::converts_runtime_errors ... ok
[INFO] [stdout] test core::error::tests::infrastructure_error_preserves_message ... ok
[INFO] [stdout] test core::error::tests::missing_local_ae_has_clear_message ... ok
[INFO] [stdout] test core::error::tests::invalid_configuration_preserves_message ... ok
[INFO] [stdout] test core::bootstrap::tests::run_runtime_maps_runtime_fatal_errors ... ok
[INFO] [stdout] test core::bootstrap::tests::run_runtime_returns_ok_on_cooperative_shutdown ... ok
[INFO] [stdout] test protocols::dimse::tests::build_service_registries_creates_one_registry_per_local_ae ... ok
[INFO] [stdout] test protocols::dimse::tests::build_service_registries_fails_when_storage_selected_without_ingest ... ok
[INFO] [stdout] test protocols::dimse::tests::build_service_registries_supports_selection_profiles ... ok
[INFO] [stdout] test protocols::dimse::tests::build_service_registries_fails_when_query_selected_without_query_service ... ok
[INFO] [stdout] test protocols::dimse::tests::start_listener_for_ae_returns_ok_for_valid_local_ae ... ok
[INFO] [stdout] test protocols::dimse::tests::start_listener_for_ae_fails_for_unknown_local_ae ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 37 tests
[INFO] [stdout] test service::tests::invalid_response_locations_are_rejected ... ok
[INFO] [stdout] test service::tests::negotiated_control_attributes_require_explicit_support ... ok
[INFO] [stdout] test service::tests::query_level_unique_key_is_optional ... ok
[INFO] [stdout] test service::tests::rejects_asterisk_universal_for_unsupported_vrs ... ok
[INFO] [stdout] test service::tests::custom_query_keys_fall_back_to_json_matching ... ok
[INFO] [stdout] test service::tests::invalid_identifier_elements_are_rejected ... ok
[INFO] [stdout] test service::tests::custom_keys_use_scu_vr_to_choose_datetime_vs_plain_range_second_matrix ... ok
[INFO] [stdout] test service::tests::builds_universal_open_range_and_question_wildcard_predicates ... ok
[INFO] [stdout] test service::tests::builds_single_uid_wildcard_range_and_uid_list_predicates ... ok
[INFO] [stdout] test service::tests::custom_keys_use_scu_vr_to_choose_datetime_vs_plain_range ... ok
[INFO] [stdout] test service::tests::custom_dt_without_range_stays_single_value ... ok
[INFO] [stdout] test service::tests::patient_root_levels_map_to_catalog_scopes ... ok
[INFO] [stdout] test service::tests::allows_keys_above_query_level ... ok
[INFO] [stdout] test service::tests::builds_sequence_predicates_using_any_item_selector ... ok
[INFO] [stdout] test service::tests::applies_optional_paging_to_catalog_query ... ok
[INFO] [stdout] test service::tests::allows_non_unique_keys_above_query_level ... ok
[INFO] [stdout] test service::tests::nested_custom_sequence_query_keys_fall_back_to_json_matching ... ok
[INFO] [stdout] test service::tests::builds_datetime_range_predicates_for_supported_keys ... ok
[INFO] [stdout] test service::tests::rejects_missing_invalid_and_unsupported_levels ... ok
[INFO] [stdout] test service::tests::rejects_invalid_range_matching_values ... ok
[INFO] [stdout] test service::tests::control_attributes_are_not_return_keys_or_predicates ... ok
[INFO] [stdout] test service::tests::rejects_non_uid_multiple_value_matching_without_extended_negotiation ... ok
[INFO] [stdout] test service::tests::rejects_sequence_matching_with_multiple_request_items ... ok
[INFO] [stdout] test service::tests::rejects_wildcard_matching_on_query_level_unique_key ... ok
[INFO] [stdout] test service::tests::rejects_wildcard_matching_for_unsupported_vrs ... ok
[INFO] [stdout] test service::tests::rejects_sequence_matching_without_request_items ... ok
[INFO] [stdout] test service::tests::series_query_does_not_require_above_level_unique_key ... ok
[INFO] [stdout] test service::tests::rejects_vr_mismatch_for_supported_query_keys ... ok
[INFO] [stdout] test service::tests::validates_dicom_datetime_timezone_bounds ... ok
[INFO] [stdout] test service::tests::study_root_levels_map_to_catalog_scopes ... ok
[INFO] [stdout] test service::tests::unpaged_request_leaves_catalog_query_unpaged ... ok
[INFO] [stdout] test service::tests::zero_length_elements_are_return_keys_only ... ok
[INFO] [stdout] test service::tests::service_inserts_zero_length_requested_keys_missing_from_projection ... ok
[INFO] [stdout] test service::tests::service_propagates_specific_character_set_to_response ... ok
[INFO] [stdout] test service::tests::service_maps_catalog_errors ... ok
[INFO] [stdout] test service::tests::service_returns_projected_identifiers_and_preserves_page_summary ... ok
[INFO] [stdout] test service::tests::service_shapes_supported_sequence_response_items ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 37 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rustcoon_retrieve-3ab1c16a2bbf35d7)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test model::tests::retrieve_request_builders_set_fields ... ok
[INFO] [stdout] test service::tests::plan_validates_required_keys ... ok
[INFO] [stdout] test service::tests::plan_fails_when_blob_reference_is_missing ... ok
[INFO] [stdout] test service::tests::plan_returns_sorted_candidates_and_suboperation_count ... ok
[INFO] [stdout] test service::tests::open_and_open_range_map_storage_errors ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rustcoon_runtime-deaa4b2f6264eaa8)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test runtime::tests::returns_fatal_error_even_if_shutdown_fails ... ok
[INFO] [stdout] test runtime::tests::force_exit_mode_does_not_block_on_slow_shutdown ... ok
[INFO] [stdout] test runtime::tests::requested_shutdown_completes_cleanly ... ok
[INFO] [stdout] test runtime::tests::does_not_cancel_shutdown_on_timeout_when_not_forced ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.08s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rustcoon_storage-21a4e08a9059730c)
[INFO] [stdout] 
[INFO] [stdout] running 13 tests
[INFO] [stdout] test error::tests::constructors_populate_expected_variants ... ok
[INFO] [stdout] test error::tests::display_messages_cover_remaining_variants ... ok
[INFO] [stdout] test key::tests::blob_key_accepts_relative_hierarchical_key ... ok
[INFO] [stdout] test key::tests::blob_key_display_and_parse_round_trip ... ok
[INFO] [stdout] test key::tests::blob_key_rejects_empty_and_absolute_values ... ok
[INFO] [stdout] test key::tests::blob_key_rejects_empty_segment ... ok
[INFO] [stdout] test key::tests::blob_key_rejects_path_traversal_and_backslashes ... ok
[INFO] [stdout] test read::tests::bounded_creates_finite_range ... ok
[INFO] [stdout] test read::tests::from_offset_creates_unbounded_range ... ok
[INFO] [stdout] test write::tests::blob_store_marker_trait_accepts_combined_store ... ok
[INFO] [stdout] test write::tests::write_request_builder_methods_set_expected_fields ... ok
[INFO] [stdout] test write::tests::mock_blob_store_and_session_traits_are_exercised ... ok
[INFO] [stdout] test write::tests::write_request_defaults_match_expected_v1_behavior ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rustcoon_storage_filesystem-9900af6c176aee3f)
[INFO] [stdout] 
[INFO] [stdout] running 12 tests
[INFO] [stdout] test tests::classify_io_error_maps_expected_variants ... ok
[INFO] [stdout] test tests::begin_write_reports_error_when_root_is_a_file ... ok
[INFO] [stdout] test tests::begin_write_reports_error_when_staging_filename_is_too_long ... ok
[INFO] [stdout] test tests::abort_discards_staged_write_and_manual_empty_abort_is_ok ... ok
[INFO] [stdout] test tests::internal_helpers_cover_remaining_non_platform_specific_error_paths ... ok
[INFO] [stdout] test tests::helper_paths_cover_remaining_internal_branches ... ok
[INFO] [stdout] test tests::open_and_head_missing_and_invalid_ranges_return_expected_errors ... ok
[INFO] [stdout] test tests::write_read_and_range_round_trip ... ok
[INFO] [stdout] test tests::delete_is_idempotent ... ok
[INFO] [stdout] test tests::unconditional_and_must_exist_writes_replace_existing_payload ... ok
[INFO] [stdout] test tests::write_preconditions_are_enforced ... ok
[INFO] [stdout] test tests::filesystem_permission_failures_cover_begin_write_and_replace_metadata_paths ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- tests::filesystem_permission_failures_cover_begin_write_and_replace_metadata_paths stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'tests::filesystem_permission_failures_cover_begin_write_and_replace_metadata_paths' (290) panicked at crates/adapter-storage-filesystem/src/lib.rs:770:9:
[INFO] [stderr] error: test failed, to rerun pass `-p rustcoon-storage-filesystem --lib`
[INFO] [stdout] assertion failed: matches!(blocked_store.begin_write(BlobWriteRequest::new(BlobKey::new("blocked/object.dcm").expect("valid blocked key"),)).await,
[INFO] [stdout]     Err(StorageError::PermissionDenied { .. } | StorageError::Backend { .. }))
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62e5a122374a - std[fce30c26ee6459b]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x62e5a122374a - std[fce30c26ee6459b]::backtrace_rs::backtrace::trace_unsynchronized::<std[fce30c26ee6459b]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x62e5a122374a - std[fce30c26ee6459b]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x62e5a122374a - <<std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[d17f6591d688f3bd]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x62e5a123798a - <core[d17f6591d688f3bd]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x62e5a123798a - core[d17f6591d688f3bd]::fmt::write
[INFO] [stdout]    6:     0x62e5a12281b2 - std[fce30c26ee6459b]::io::default_write_fmt::<alloc[dad09f3a48280dba]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x62e5a12281b2 - <alloc[dad09f3a48280dba]::vec::Vec<u8> as std[fce30c26ee6459b]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x62e5a11ffcdf - <std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x62e5a11ffcdf - std[fce30c26ee6459b]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x62e5a121ab49 - std[fce30c26ee6459b]::panicking::default_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x62e5a111f78c - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x62e5a111f78c - test[9653f4f1d2ca1489]::test_main_inner::<test[9653f4f1d2ca1489]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x62e5a121adc2 - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x62e5a121adc2 - std[fce30c26ee6459b]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x62e5a11ffdca - std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:691:13
[INFO] [stdout]   16:     0x62e5a11f4d09 - std[fce30c26ee6459b]::sys::backtrace::__rust_end_short_backtrace::<std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x62e5a1200ccd - __rustc[de152adb51f02556]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x62e5a123814c - core[d17f6591d688f3bd]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x62e5a1238112 - core[d17f6591d688f3bd]::panicking::panic
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panicking.rs:150:5
[INFO] [stdout]   20:     0x62e5a110570b - rustcoon_storage_filesystem[17dcc792d26e8b3f]::tests::filesystem_permission_failures_cover_begin_write_and_replace_metadata_paths::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/adapter-storage-filesystem/src/lib.rs:770:9
[INFO] [stdout]   21:     0x62e5a10d57e2 - <core[d17f6591d688f3bd]::pin::Pin<&mut dyn core[d17f6591d688f3bd]::future::future::Future<Output = ()>> as core[d17f6591d688f3bd]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/future/future.rs:133:9
[INFO] [stdout]   22:     0x62e5a10d585d - <core[d17f6591d688f3bd]::pin::Pin<&mut core[d17f6591d688f3bd]::pin::Pin<&mut dyn core[d17f6591d688f3bd]::future::future::Future<Output = ()>>> as core[d17f6591d688f3bd]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/future/future.rs:133:9
[INFO] [stdout]   23:     0x62e5a10dc03d - <tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[d17f6591d688f3bd]::pin::Pin<&mut core[d17f6591d688f3bd]::pin::Pin<&mut dyn core[d17f6591d688f3bd]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:769:70
[INFO] [stdout]   24:     0x62e5a10dbe6b - tokio[4603bafb6ad8c93d]::task::coop::with_budget::<core[d17f6591d688f3bd]::task::poll::Poll<()>, <tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[d17f6591d688f3bd]::pin::Pin<&mut core[d17f6591d688f3bd]::pin::Pin<&mut dyn core[d17f6591d688f3bd]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/task/coop/mod.rs:167:5
[INFO] [stdout]   25:     0x62e5a10dbe6b - tokio[4603bafb6ad8c93d]::task::coop::budget::<core[d17f6591d688f3bd]::task::poll::Poll<()>, <tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[d17f6591d688f3bd]::pin::Pin<&mut core[d17f6591d688f3bd]::pin::Pin<&mut dyn core[d17f6591d688f3bd]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/task/coop/mod.rs:133:5
[INFO] [stdout]   26:     0x62e5a10dbe6b - <tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[d17f6591d688f3bd]::pin::Pin<&mut core[d17f6591d688f3bd]::pin::Pin<&mut dyn core[d17f6591d688f3bd]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:769:25
[INFO] [stdout]   27:     0x62e5a10d6c20 - <tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::Context>::enter::<core[d17f6591d688f3bd]::task::poll::Poll<()>, <tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[d17f6591d688f3bd]::pin::Pin<&mut core[d17f6591d688f3bd]::pin::Pin<&mut dyn core[d17f6591d688f3bd]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:446:19
[INFO] [stdout]   28:     0x62e5a10da83d - <tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[d17f6591d688f3bd]::pin::Pin<&mut core[d17f6591d688f3bd]::pin::Pin<&mut dyn core[d17f6591d688f3bd]::future::future::Future<Output = ()>>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:768:44
[INFO] [stdout]   29:     0x62e5a10d9ae4 - <tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[d17f6591d688f3bd]::pin::Pin<&mut core[d17f6591d688f3bd]::pin::Pin<&mut dyn core[d17f6591d688f3bd]::future::future::Future<Output = ()>>>>::{closure#0}, core[d17f6591d688f3bd]::option::Option<()>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:856:68
[INFO] [stdout]   30:     0x62e5a10ebbdb - <tokio[4603bafb6ad8c93d]::runtime::context::scoped::Scoped<tokio[4603bafb6ad8c93d]::runtime::scheduler::Context>>::set::<<tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[d17f6591d688f3bd]::pin::Pin<&mut core[d17f6591d688f3bd]::pin::Pin<&mut dyn core[d17f6591d688f3bd]::future::future::Future<Output = ()>>>>::{closure#0}, core[d17f6591d688f3bd]::option::Option<()>>::{closure#0}, (alloc[dad09f3a48280dba]::boxed::Box<tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::Core>, core[d17f6591d688f3bd]::option::Option<()>)>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/context/scoped.rs:40:9
[INFO] [stdout]   31:     0x62e5a10db909 - tokio[4603bafb6ad8c93d]::runtime::context::set_scheduler::<(alloc[dad09f3a48280dba]::boxed::Box<tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::Core>, core[d17f6591d688f3bd]::option::Option<()>), <tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[d17f6591d688f3bd]::pin::Pin<&mut core[d17f6591d688f3bd]::pin::Pin<&mut dyn core[d17f6591d688f3bd]::future::future::Future<Output = ()>>>>::{closure#0}, core[d17f6591d688f3bd]::option::Option<()>>::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/context.rs:176:38
[INFO] [stdout]   32:     0x62e5a10ec3e2 - <std[fce30c26ee6459b]::thread::local::LocalKey<tokio[4603bafb6ad8c93d]::runtime::context::Context>>::try_with::<tokio[4603bafb6ad8c93d]::runtime::context::set_scheduler<(alloc[dad09f3a48280dba]::boxed::Box<tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::Core>, core[d17f6591d688f3bd]::option::Option<()>), <tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[d17f6591d688f3bd]::pin::Pin<&mut core[d17f6591d688f3bd]::pin::Pin<&mut dyn core[d17f6591d688f3bd]::future::future::Future<Output = ()>>>>::{closure#0}, core[d17f6591d688f3bd]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[dad09f3a48280dba]::boxed::Box<tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::Core>, core[d17f6591d688f3bd]::option::Option<()>)>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/local.rs:462:12
[INFO] [stdout]   33:     0x62e5a10ebf6e - <std[fce30c26ee6459b]::thread::local::LocalKey<tokio[4603bafb6ad8c93d]::runtime::context::Context>>::with::<tokio[4603bafb6ad8c93d]::runtime::context::set_scheduler<(alloc[dad09f3a48280dba]::boxed::Box<tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::Core>, core[d17f6591d688f3bd]::option::Option<()>), <tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[d17f6591d688f3bd]::pin::Pin<&mut core[d17f6591d688f3bd]::pin::Pin<&mut dyn core[d17f6591d688f3bd]::future::future::Future<Output = ()>>>>::{closure#0}, core[d17f6591d688f3bd]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[dad09f3a48280dba]::boxed::Box<tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::Core>, core[d17f6591d688f3bd]::option::Option<()>)>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/local.rs:426:20
[INFO] [stdout]   34:     0x62e5a10d817d - tokio[4603bafb6ad8c93d]::runtime::context::set_scheduler::<(alloc[dad09f3a48280dba]::boxed::Box<tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::Core>, core[d17f6591d688f3bd]::option::Option<()>), <tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[d17f6591d688f3bd]::pin::Pin<&mut core[d17f6591d688f3bd]::pin::Pin<&mut dyn core[d17f6591d688f3bd]::future::future::Future<Output = ()>>>>::{closure#0}, core[d17f6591d688f3bd]::option::Option<()>>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/context.rs:176:17
[INFO] [stdout]   35:     0x62e5a10d7d30 - <tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[d17f6591d688f3bd]::pin::Pin<&mut core[d17f6591d688f3bd]::pin::Pin<&mut dyn core[d17f6591d688f3bd]::future::future::Future<Output = ()>>>>::{closure#0}, core[d17f6591d688f3bd]::option::Option<()>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:856:27
[INFO] [stdout]   36:     0x62e5a10d8026 - <tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[d17f6591d688f3bd]::pin::Pin<&mut core[d17f6591d688f3bd]::pin::Pin<&mut dyn core[d17f6591d688f3bd]::future::future::Future<Output = ()>>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:756:24
[INFO] [stdout]   37:     0x62e5a10d96f0 - <tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::CurrentThread>::block_on::<core[d17f6591d688f3bd]::pin::Pin<&mut dyn core[d17f6591d688f3bd]::future::future::Future<Output = ()>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:200:33
[INFO] [stdout]   38:     0x62e5a10cfe73 - tokio[4603bafb6ad8c93d]::runtime::context::runtime::enter_runtime::<<tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[d17f6591d688f3bd]::pin::Pin<&mut dyn core[d17f6591d688f3bd]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   39:     0x62e5a10d6751 - <tokio[4603bafb6ad8c93d]::runtime::scheduler::current_thread::CurrentThread>::block_on::<core[d17f6591d688f3bd]::pin::Pin<&mut dyn core[d17f6591d688f3bd]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:188:9
[INFO] [stdout]   40:     0x62e5a10e16f4 - <tokio[4603bafb6ad8c93d]::runtime::runtime::Runtime>::block_on_inner::<core[d17f6591d688f3bd]::pin::Pin<&mut dyn core[d17f6591d688f3bd]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/runtime.rs:371:52
[INFO] [stdout]   41:     0x62e5a10e18bf - <tokio[4603bafb6ad8c93d]::runtime::runtime::Runtime>::block_on::<core[d17f6591d688f3bd]::pin::Pin<&mut dyn core[d17f6591d688f3bd]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/runtime.rs:345:18
[INFO] [stdout]   42:     0x62e5a1107a43 - rustcoon_storage_filesystem[17dcc792d26e8b3f]::tests::filesystem_permission_failures_cover_begin_write_and_replace_metadata_paths
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/adapter-storage-filesystem/src/lib.rs:813:81
[INFO] [stdout]   43:     0x62e5a10fad37 - rustcoon_storage_filesystem[17dcc792d26e8b3f]::tests::filesystem_permission_failures_cover_begin_write_and_replace_metadata_paths::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/adapter-storage-filesystem/src/lib.rs:755:91
[INFO] [stdout]   44:     0x62e5a10bce16 - <rustcoon_storage_filesystem[17dcc792d26e8b3f]::tests::filesystem_permission_failures_cover_begin_write_and_replace_metadata_paths::{closure#0} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   45:     0x62e5a1112a6b - <fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   46:     0x62e5a1112a6b - test[9653f4f1d2ca1489]::__rust_begin_short_backtrace::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:724:18
[INFO] [stdout]   47:     0x62e5a112025b - test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:74
[INFO] [stdout]   48:     0x62e5a112025b - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   49:     0x62e5a112025b - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   50:     0x62e5a112025b - std[fce30c26ee6459b]::panicking::catch_unwind::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   51:     0x62e5a112025b - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   52:     0x62e5a112025b - test[9653f4f1d2ca1489]::run_test_in_process
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:27
[INFO] [stdout]   53:     0x62e5a112025b - test[9653f4f1d2ca1489]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:668:43
[INFO] [stdout]   54:     0x62e5a1119974 - test[9653f4f1d2ca1489]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:698:41
[INFO] [stdout]   55:     0x62e5a1119974 - std[fce30c26ee6459b]::sys::backtrace::__rust_begin_short_backtrace::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   56:     0x62e5a1122e62 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   57:     0x62e5a1122e62 - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   58:     0x62e5a1122e62 - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   59:     0x62e5a1122e62 - std[fce30c26ee6459b]::panicking::catch_unwind::<(), core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   60:     0x62e5a1122e62 - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   61:     0x62e5a1122e62 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   62:     0x62e5a1122e62 - <std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   63:     0x62e5a1222b7f - <alloc[dad09f3a48280dba]::boxed::Box<dyn core[d17f6591d688f3bd]::ops::function::FnOnce<(), Output = ()> + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   64:     0x62e5a1222b7f - <std[fce30c26ee6459b]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   65:     0x75d1c702aaa4 - <unknown>
[INFO] [stdout]   66:     0x75d1c70b7a64 - clone
[INFO] [stdout]   67:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     tests::filesystem_permission_failures_cover_begin_write_and_replace_metadata_paths
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 11 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "08ccda93fcdd43bc326602d918c409f3b8274ad261fb618b4abe340085613290", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "08ccda93fcdd43bc326602d918c409f3b8274ad261fb618b4abe340085613290", kill_on_drop: false }`
[INFO] [stdout] 08ccda93fcdd43bc326602d918c409f3b8274ad261fb618b4abe340085613290
